[INFO] cloning repository https://github.com/Bazooh/RustLox
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Bazooh/RustLox" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f0dfc0cf917f5997c94a6cb5556b8b9c354da810
[INFO] testing Bazooh/RustLox against master#f9988fefd3add01f414f52b414308e7872622fee for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/Bazooh/RustLox
[INFO] finished tweaking git repo https://github.com/Bazooh/RustLox
[INFO] tweaked toml for git repo https://github.com/Bazooh/RustLox written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/Bazooh/RustLox on toolchain f9988fefd3add01f414f52b414308e7872622fee
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/Bazooh/RustLox already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded rstest v0.26.1
[INFO] [stderr]   Downloaded proc-macro2 v1.0.104
[INFO] [stderr]   Downloaded relative-path v1.9.3
[INFO] [stderr]   Downloaded rstest_macros v0.26.1
[INFO] [stderr]   Downloaded syn v2.0.112
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 54b43b5feaf84645cb1b027c374626320b5b957aa8cd4cf8120dd6604e0d186b
[INFO] running `Command { std: "docker" "start" "-a" "54b43b5feaf84645cb1b027c374626320b5b957aa8cd4cf8120dd6604e0d186b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "54b43b5feaf84645cb1b027c374626320b5b957aa8cd4cf8120dd6604e0d186b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "54b43b5feaf84645cb1b027c374626320b5b957aa8cd4cf8120dd6604e0d186b", kill_on_drop: false }`
[INFO] [stdout] 54b43b5feaf84645cb1b027c374626320b5b957aa8cd4cf8120dd6604e0d186b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4c69b9006a17bf8477695367e14d673379dbb65a7a562d2d5b008bfe3700adce
[INFO] running `Command { std: "docker" "start" "-a" "4c69b9006a17bf8477695367e14d673379dbb65a7a562d2d5b008bfe3700adce", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.104
[INFO] [stderr]    Compiling unicode-ident v1.0.22
[INFO] [stderr]    Compiling quote v1.0.42
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling winnow v0.7.14
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling rstest_macros v0.26.1
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling relative-path v1.9.3
[INFO] [stderr]    Compiling pin-project-lite v0.2.16
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling futures-timer v3.0.3
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling syn v2.0.112
[INFO] [stderr]    Compiling toml_parser v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling toml_edit v0.23.10+spec-1.0.0
[INFO] [stderr]    Compiling proc-macro-crate v3.4.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling rstest v0.26.1
[INFO] [stderr]    Compiling RustLox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: method `repr` is never used
[INFO] [stdout]   --> src/instruction.rs:96:12
[INFO] [stdout]    |
[INFO] [stdout] 95 | impl Instruction {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] 96 |     pub fn repr(&self) -> String {
[INFO] [stdout]    |            ^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `parse_expect` is never used
[INFO] [stdout]  --> src/test.rs:6:4
[INFO] [stdout]   |
[INFO] [stdout] 6 | fn parse_expect(content: &str) -> (String, String) {
[INFO] [stdout]   |    ^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_file` is never used
[INFO] [stdout]   --> src/test.rs:35:4
[INFO] [stdout]    |
[INFO] [stdout] 35 | fn test_file(path: &str, func: fn(&str) -> (String, String)) {
[INFO] [stdout]    |    ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_interpret_file` is never used
[INFO] [stdout]   --> src/test.rs:91:4
[INFO] [stdout]    |
[INFO] [stdout] 91 | fn test_interpret_file(path: &str) {
[INFO] [stdout]    |    ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_tokenize_file` is never used
[INFO] [stdout]    --> src/test.rs:108:4
[INFO] [stdout]     |
[INFO] [stdout] 108 | fn test_tokenize_file(path: &str) {
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 39.13s
[INFO] running `Command { std: "docker" "inspect" "4c69b9006a17bf8477695367e14d673379dbb65a7a562d2d5b008bfe3700adce", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4c69b9006a17bf8477695367e14d673379dbb65a7a562d2d5b008bfe3700adce", kill_on_drop: false }`
[INFO] [stdout] 4c69b9006a17bf8477695367e14d673379dbb65a7a562d2d5b008bfe3700adce
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 37d6cbef3898fd058cc30eeda34161a874acd5d610aedc30c891754c273650a3
[INFO] running `Command { std: "docker" "start" "-a" "37d6cbef3898fd058cc30eeda34161a874acd5d610aedc30c891754c273650a3", kill_on_drop: false }`
[INFO] [stderr]    Compiling RustLox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: method `repr` is never used
[INFO] [stdout]   --> src/instruction.rs:96:12
[INFO] [stdout]    |
[INFO] [stdout] 95 | impl Instruction {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] 96 |     pub fn repr(&self) -> String {
[INFO] [stdout]    |            ^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.18s
[INFO] running `Command { std: "docker" "inspect" "37d6cbef3898fd058cc30eeda34161a874acd5d610aedc30c891754c273650a3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "37d6cbef3898fd058cc30eeda34161a874acd5d610aedc30c891754c273650a3", kill_on_drop: false }`
[INFO] [stdout] 37d6cbef3898fd058cc30eeda34161a874acd5d610aedc30c891754c273650a3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ac56f06acd70e4c38b0060416afb4bb3d81dbcec2b834df1943ca55249541842
[INFO] running `Command { std: "docker" "start" "-a" "ac56f06acd70e4c38b0060416afb4bb3d81dbcec2b834df1943ca55249541842", kill_on_drop: false }`
[INFO] [stderr] warning: method `repr` is never used
[INFO] [stdout] 
[INFO] [stderr]   --> src/instruction.rs:96:12
[INFO] [stdout] running 31 tests
[INFO] [stderr]    |
[INFO] [stderr] 95 | impl Instruction {
[INFO] [stderr]    | ---------------- method in this implementation
[INFO] [stderr] 96 |     pub fn repr(&self) -> String {
[INFO] [stderr]    |            ^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `RustLox` (bin "RustLox" test) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/RustLox-a3bc338cdf622158)
[INFO] [stdout] test test::test_empty_file ... ok
[INFO] [stdout] test test::test_module::case_03_bool ... ok
[INFO] [stdout] test test::test_module::case_09_expressions ... ok
[INFO] [stdout] test test::test_module::case_02_block ... ok
[INFO] [stdout] test test::test_module::case_07_comments ... ok
[INFO] [stdout] test test::test_module::case_04_call ... ok
[INFO] [stdout] test test::test_module::case_05_class ... FAILED
[INFO] [stdout] test test::test_module::case_08_constructor ... FAILED
[INFO] [stdout] test test::test_module::case_01_assignment ... ok
[INFO] [stdout] test test::test_module::case_15_logical_operator ... ok
[INFO] [stdout] test test::test_module::case_11_for_ ... ok
[INFO] [stdout] test test::test_module::case_17_nil ... ok
[INFO] [stdout] test test::test_module::case_13_if_ ... ok
[INFO] [stdout] test test::test_module::case_20_print ... ok
[INFO] [stdout] test test::test_module::case_18_number ... ok
[INFO] [stdout] test test::test_module::case_21_regression ... ok
[INFO] [stdout] test test::test_module::case_14_inheritance ... FAILED
[INFO] [stdout] test test::test_module::case_06_closure ... ok
[INFO] [stdout] test test::test_module::case_16_method ... ok
[INFO] [stdout] test test::test_module::case_25_this ... FAILED
[INFO] [stdout] test test::test_module::case_23_string ... ok
[INFO] [stdout] test test::test_module::case_24_super ... FAILED
[INFO] [stdout] test test::test_precedence ... ok
[INFO] [stdout] test test::test_unexpected_character ... ok
[INFO] [stdout] test test::test_module::case_22_return_ ... ok
[INFO] [stdout] test test::test_module::case_27_while_ ... ok
[INFO] [stdout] test test::test_module::case_12_function ... ok
[INFO] [stdout] test test::test_module::case_10_field ... ok
[INFO] [stdout] test test::test_tokenize::case_1 ... ok
[INFO] [stdout] test test::test_module::case_19_operator ... ok
[INFO] [stdout] test test::test_module::case_26_variable ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_05_class stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 0, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: GetGlobal { string_index: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: CreateGlobal { string_index: 1 } Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<Foo instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<Foo instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Call { arg_count: 0 } Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<Foo instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 1 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: DotProperty(0) Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<Foo instance>", "<fn returnSelf>", "<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Call { arg_count: 0 } Stack: ["<fn returnSelf>"]
[INFO] [stdout] GC: checking value "<fn returnSelf>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<Foo instance>", "<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: ["<fn returnSelf>", "<Foo instance>"]
[INFO] [stdout] Executing byte at 11: Return Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Print Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Local(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: GetLocal(0) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Call { arg_count: 0 } Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "&<pointer 1>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: DotProperty(0) Stack: ["&<class Foo>", "<Foo instance>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<class Foo>", "<Foo instance>", "<fn returnSelf>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["&<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<fn returnSelf>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "<Foo instance>", "&<pointer 1>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: Capture(Local(0)) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "&<pointer 1>"]
[INFO] [stdout] FREEING: ["<Foo instance>", "<fn returnSelf>"]
[INFO] [stdout] Executing byte at 11: GetCaptured(2) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "&<pointer 1>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: Return Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Print Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<class Foo>", "&<pointer 1>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Pop(1) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: ["&<pointer 1>", "<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] [
[INFO] [stdout]     Constant(
[INFO] [stdout]         0.0,
[INFO] [stdout]     ),
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 31,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Constant(
[INFO] [stdout]         1.0,
[INFO] [stdout]     ),
[INFO] [stdout]     Add,
[INFO] [stdout]     AssignGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Constant(0.0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["0"]
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<fn print_id>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: GetGlobal { string_index: 4 } Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<fn print_id>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<fn print_id>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<fn print_id>"]
[INFO] [stdout] FREEING: ["<fn init>", "<fn print_id>", "<A instance>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_05_class' (23) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x61294af2cefa - std[1209cfb42f6d497f]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x61294af2cefa - std[1209cfb42f6d497f]::backtrace_rs::backtrace::trace_unsynchronized::<std[1209cfb42f6d497f]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x61294af2cefa - std[1209cfb42f6d497f]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x61294af2cefa - <<std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[4a92201981c6b77a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x61294af440ca - <core[4a92201981c6b77a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x61294af440ca - core[4a92201981c6b77a]::fmt::write
[INFO] [stdout]    6:     0x61294af31982 - std[1209cfb42f6d497f]::io::default_write_fmt::<alloc[420bb310a727dfd7]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x61294af31982 - <alloc[420bb310a727dfd7]::vec::Vec<u8> as std[1209cfb42f6d497f]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x61294af0b89f - <std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x61294af0b89f - std[1209cfb42f6d497f]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x61294af24e19 - std[1209cfb42f6d497f]::panicking::default_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x61294ac4a58c - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x61294ac4a58c - test[59e67a9bc0e119f1]::test_main_inner::<test[59e67a9bc0e119f1]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x61294af24fd2 - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x61294af24fd2 - std[1209cfb42f6d497f]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x61294af0b958 - std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x61294af00989 - std[1209cfb42f6d497f]::sys::backtrace::__rust_end_short_backtrace::<std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x61294af0c66d - __rustc[4effac393e9f3da6]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x61294af4490c - core[4a92201981c6b77a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x61294af44604 - core[4a92201981c6b77a]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x61294af44604 - core[4a92201981c6b77a]::option::expect_failed
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x61294ac11dc0 - <core[4a92201981c6b77a]::option::Option<&RustLox[f6ee2a17f4eb2240]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x61294ac30d3e - <RustLox[f6ee2a17f4eb2240]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x61294abff8fd - <RustLox[f6ee2a17f4eb2240]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x61294ac0251a - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x61294ac04b22 - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x61294ac0ef8a - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x61294ac26c46 - RustLox[f6ee2a17f4eb2240]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x61294ac2b8ea - <RustLox[f6ee2a17f4eb2240]::test::test_interpret_file::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x61294ac29771 - RustLox[f6ee2a17f4eb2240]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x61294ac294fa - RustLox[f6ee2a17f4eb2240]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x61294ac28436 - RustLox[f6ee2a17f4eb2240]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x61294ac31122 - RustLox[f6ee2a17f4eb2240]::test::test_module::case_05_class
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x61294ac2d937 - RustLox[f6ee2a17f4eb2240]::test::test_module::case_05_class::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x61294ac2bb26 - <RustLox[f6ee2a17f4eb2240]::test::test_module::case_05_class::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x61294ac3d86b - <fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x61294ac3d86b - test[59e67a9bc0e119f1]::__rust_begin_short_backtrace::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x61294ac4b05b - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x61294ac4b05b - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x61294ac4b05b - std[1209cfb42f6d497f]::panicking::catch_unwind::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x61294ac4b05b - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test_in_process
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x61294ac44774 - test[59e67a9bc0e119f1]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x61294ac44774 - std[1209cfb42f6d497f]::sys::backtrace::__rust_begin_short_backtrace::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x61294ac4dc62 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x61294ac4dc62 - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panicking::catch_unwind::<(), core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x61294ac4dc62 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x61294ac4dc62 - <std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x61294af2c4af - <alloc[420bb310a727dfd7]::boxed::Box<dyn core[4a92201981c6b77a]::ops::function::FnOnce<(), Output = ()> + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   54:     0x61294af2c4af - <std[1209cfb42f6d497f]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x7be15ac36aa4 - <unknown>
[INFO] [stdout]   56:     0x7be15acc3a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_08_constructor stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 2, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 2 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: GetGlobal { string_index: 2 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: String(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: ["<fn init>", "<Foo instance>"]
[INFO] [stdout] Executing byte at 11: Print Stack: ["init"]
[INFO] [stdout] GC: checking value "init"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>", "init"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: ["init"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_08_constructor' (26) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x61294af2cefa - std[1209cfb42f6d497f]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x61294af2cefa - std[1209cfb42f6d497f]::backtrace_rs::backtrace::trace_unsynchronized::<std[1209cfb42f6d497f]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x61294af2cefa - std[1209cfb42f6d497f]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x61294af2cefa - <<std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[4a92201981c6b77a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x61294af440ca - <core[4a92201981c6b77a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x61294af440ca - core[4a92201981c6b77a]::fmt::write
[INFO] [stdout]    6:     0x61294af31982 - std[1209cfb42f6d497f]::io::default_write_fmt::<alloc[420bb310a727dfd7]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x61294af31982 - <alloc[420bb310a727dfd7]::vec::Vec<u8> as std[1209cfb42f6d497f]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x61294af0b89f - <std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x61294af0b89f - std[1209cfb42f6d497f]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x61294af24e19 - std[1209cfb42f6d497f]::panicking::default_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x61294ac4a58c - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x61294ac4a58c - test[59e67a9bc0e119f1]::test_main_inner::<test[59e67a9bc0e119f1]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x61294af24fd2 - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x61294af24fd2 - std[1209cfb42f6d497f]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x61294af0b958 - std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x61294af00989 - std[1209cfb42f6d497f]::sys::backtrace::__rust_end_short_backtrace::<std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x61294af0c66d - __rustc[4effac393e9f3da6]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x61294af4490c - core[4a92201981c6b77a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x61294af44604 - core[4a92201981c6b77a]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x61294af44604 - core[4a92201981c6b77a]::option::expect_failed
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x61294ac11dc0 - <core[4a92201981c6b77a]::option::Option<&RustLox[f6ee2a17f4eb2240]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x61294ac30d3e - <RustLox[f6ee2a17f4eb2240]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x61294abff8fd - <RustLox[f6ee2a17f4eb2240]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x61294ac0251a - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x61294ac04b22 - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x61294ac0ef8a - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x61294ac26c46 - RustLox[f6ee2a17f4eb2240]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x61294ac2b8ea - <RustLox[f6ee2a17f4eb2240]::test::test_interpret_file::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x61294ac29771 - RustLox[f6ee2a17f4eb2240]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x61294ac294fa - RustLox[f6ee2a17f4eb2240]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x61294ac28436 - RustLox[f6ee2a17f4eb2240]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x61294ac31522 - RustLox[f6ee2a17f4eb2240]::test::test_module::case_08_constructor
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x61294ac2dc37 - RustLox[f6ee2a17f4eb2240]::test::test_module::case_08_constructor::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x61294ac2bf26 - <RustLox[f6ee2a17f4eb2240]::test::test_module::case_08_constructor::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x61294ac3d86b - <fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x61294ac3d86b - test[59e67a9bc0e119f1]::__rust_begin_short_backtrace::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x61294ac4b05b - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x61294ac4b05b - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x61294ac4b05b - std[1209cfb42f6d497f]::panicking::catch_unwind::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x61294ac4b05b - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test_in_process
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x61294ac44774 - test[59e67a9bc0e119f1]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x61294ac44774 - std[1209cfb42f6d497f]::sys::backtrace::__rust_begin_short_backtrace::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x61294ac4dc62 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x61294ac4dc62 - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panicking::catch_unwind::<(), core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x61294ac4dc62 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x61294ac4dc62 - <std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x61294af2c4af - <alloc[420bb310a727dfd7]::boxed::Box<dyn core[4a92201981c6b77a]::ops::function::FnOnce<(), Output = ()> + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   54:     0x61294af2c4af - <std[1209cfb42f6d497f]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x7be15ac36aa4 - <unknown>
[INFO] [stdout]   56:     0x7be15acc3a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_14_inheritance stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 36,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 45,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 7,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 4,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 56,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 67,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 7,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 7,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         8,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         9,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         10,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         11,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 4 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn fooPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 7, superclass: Some(Global(4)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 7: CreateGlobal { string_index: 7 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<class Bar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: GetGlobal { string_index: 7 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn bar>", "<fn barPrint>", "<class Bar>", "<fn fooPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<class Foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: CreateGlobal { string_index: 5 } Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<fn bar>", "<fn barPrint>", "<Bar instance>", "<class Foo>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: String(8) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<fn barPrint>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn bar>", "<class Foo>", "<class Bar>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: String(9) Stack: ["foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<fn foo>", "<fn fooPrint>", "<Bar instance>", "<class Bar>", "<fn bar>", "<fn bar>", "<class Foo>", "foo 1", "<fn barPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: GetGlobal { string_index: 5 } Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["foo 1", "foo 2", "<class Foo>", "<fn foo>", "<fn foo>", "<class Bar>", "<fn bar>", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: DotProperty(3) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<fn barPrint>", "foo 2", "<fn foo>", "<Bar instance>", "<class Foo>", "<fn bar>", "foo 1", "<fn fooPrint>", "<class Bar>", "<fn fooPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Call { arg_count: 2 } Stack: ["foo 1", "foo 2", "<fn foo>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<fn bar>", "<Bar instance>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<class Foo>", "foo 2", "foo 1", "<fn barPrint>", "<class Bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 46: GetCaptured(0) Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<fn barPrint>", "foo 2", "<class Bar>", "<fn bar>", "<class Foo>", "<fn fooPrint>", "foo 1", "<fn foo>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 47: GetLocal(0) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn foo>", "<class Bar>", "foo 1", "<fn fooPrint>", "foo 2", "<fn bar>", "<fn foo>", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 48: AssignField { field_name_index: 1 } Stack: ["foo 1", "foo 2", "<Bar instance>", "foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "foo 2", "<class Foo>", "<Bar instance>", "<class Bar>", "<fn fooPrint>", "<fn foo>", "<fn foo>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 49: Pop(1) Stack: ["foo 1", "foo 2", "foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "foo 2", "<fn foo>", "<class Bar>", "foo 1", "<fn barPrint>", "<Bar instance>", "<fn fooPrint>", "<fn fooPrint>", "<class Foo>", "<fn foo>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 50: GetCaptured(0) Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["foo 2", "<class Bar>", "<fn barPrint>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<class Foo>", "<Bar instance>", "<fn bar>", "<fn fooPrint>", "<fn fooPrint>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 51: GetLocal(1) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "foo 2", "<fn fooPrint>", "<fn foo>", "<fn foo>", "<fn bar>", "foo 1", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<fn barPrint>", "<class Bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 52: AssignField { field_name_index: 2 } Stack: ["foo 1", "foo 2", "<Bar instance>", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn barPrint>", "foo 2", "<fn foo>", "<class Foo>", "<fn fooPrint>", "foo 1", "<fn bar>", "<fn fooPrint>", "<fn bar>", "<Bar instance>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 53: Pop(1) Stack: ["foo 1", "foo 2", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "foo 2", "<fn barPrint>", "<fn foo>", "foo 1", "<class Foo>", "<fn barPrint>", "<fn bar>", "<Bar instance>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 54: Nil Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn fooPrint>", "<class Foo>", "<class Bar>", "<Bar instance>", "foo 1", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn barPrint>", "foo 2", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 55: Return Stack: ["foo 1", "foo 2", "nil"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["foo 1", "<fn fooPrint>", "foo 2", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<fn foo>", "<class Foo>", "<class Bar>", "<fn fooPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn fooPrint>", "<Bar instance>", "<fn foo>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<class Bar>", "foo 1", "<class Foo>", "<fn fooPrint>", "foo 2", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<Bar instance>", "<fn fooPrint>", "foo 2", "foo 1", "<fn fooPrint>", "<fn bar>", "<class Foo>", "<fn barPrint>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<fn foo>", "<Bar instance>", "<fn bar>", "foo 2", "<fn barPrint>", "<fn fooPrint>", "<class Bar>", "foo 1", "<class Foo>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: Call { arg_count: 0 } Stack: ["<fn fooPrint>"]
[INFO] [stdout] GC: checking value "<fn fooPrint>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn barPrint>", "<fn foo>", "<class Bar>", "foo 2", "foo 1", "<fn foo>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "<Bar instance>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Foo>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "<class Bar>", "<fn bar>", "foo 1", "<fn foo>", "<fn barPrint>", "<Bar instance>", "<fn bar>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<class Bar>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<class Foo>", "foo 2", "<fn foo>", "<Bar instance>", "<fn fooPrint>", "<fn foo>", "foo 1", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 39: Print Stack: ["foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn barPrint>", "<Bar instance>", "<class Foo>", "<fn foo>", "foo 2", "<fn fooPrint>", "<class Bar>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<fn bar>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "foo 1", "foo 2", "<class Foo>", "<fn barPrint>", "<fn bar>", "<fn barPrint>", "<fn bar>", "<Bar instance>", "<fn fooPrint>", "<fn foo>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["foo 2", "<class Bar>", "foo 1", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "<class Foo>", "<fn foo>", "<fn foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Print Stack: ["foo 2"]
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["foo 1", "foo 2", "<fn fooPrint>", "<fn bar>", "<class Foo>", "<fn foo>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn bar>", "<Bar instance>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 43: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<fn barPrint>", "<fn bar>", "foo 1", "foo 2", "<class Foo>", "<fn fooPrint>", "<fn foo>", "<Bar instance>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 44: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["foo 2", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn bar>", "<Bar instance>", "<class Bar>", "<fn barPrint>", "foo 1", "<class Foo>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "foo 1", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<Bar instance>", "<fn bar>", "<class Foo>", "<fn bar>", "foo 2", "<class Bar>", "<fn barPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: String(10) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["foo 1", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>", "<class Foo>", "<fn foo>", "<fn foo>", "<class Bar>", "<fn bar>", "<fn bar>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: String(11) Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Foo>", "bar 1", "<class Bar>", "foo 1", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<fn barPrint>", "<Bar instance>", "<fn foo>", "<fn foo>", "foo 2", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: GetGlobal { string_index: 5 } Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<class Bar>", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn barPrint>", "bar 2", "bar 1", "foo 2", "<class Foo>", "foo 1", "<fn foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 24: DotProperty(5) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "bar 1", "<fn fooPrint>", "<fn barPrint>", "bar 2", "<fn foo>", "<Bar instance>", "<class Bar>", "foo 1", "<fn barPrint>", "<fn bar>", "<fn bar>", "<fn foo>", "<class Foo>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 25: Call { arg_count: 2 } Stack: ["bar 1", "bar 2", "<fn bar>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<fn bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "bar 2", "<fn fooPrint>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn foo>", "<Bar instance>", "foo 1", "<fn bar>", "foo 2", "<fn bar>", "<fn barPrint>", "bar 1", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 57: GetCaptured(0) Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "foo 1", "<fn foo>", "<fn fooPrint>", "bar 1", "<fn fooPrint>", "<class Foo>", "foo 2", "<class Bar>", "<fn bar>", "<Bar instance>", "<fn foo>", "bar 2", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 58: GetLocal(0) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "foo 1", "<fn bar>", "foo 2", "<fn fooPrint>", "bar 2", "<fn bar>", "<class Foo>", "<fn foo>", "<class Bar>", "<fn fooPrint>", "bar 1", "<fn foo>", "<fn barPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 59: AssignField { field_name_index: 1 } Stack: ["bar 1", "bar 2", "<Bar instance>", "bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn bar>", "bar 1", "<fn barPrint>", "foo 1", "<Bar instance>", "foo 2", "<class Bar>", "<fn bar>", "<fn barPrint>", "bar 2", "<class Foo>", "<fn foo>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 60: Pop(1) Stack: ["bar 1", "bar 2", "bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 2", "<fn barPrint>", "<fn foo>", "<fn foo>", "<fn bar>", "<class Foo>", "foo 2", "<class Bar>", "<fn bar>", "<fn barPrint>", "bar 1", "<fn fooPrint>", "<fn fooPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: ["foo 1"]
[INFO] [stdout] Executing byte at 61: GetCaptured(0) Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<Bar instance>", "<fn foo>", "bar 2", "foo 2", "<fn fooPrint>", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<class Foo>", "<fn barPrint>", "<fn bar>", "bar 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 62: GetLocal(1) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "bar 1", "<fn fooPrint>", "foo 2", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "<Bar instance>", "bar 2", "<fn foo>", "<class Bar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 63: AssignField { field_name_index: 2 } Stack: ["bar 1", "bar 2", "<Bar instance>", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<Bar instance>", "foo 2", "<fn bar>", "<class Foo>", "<fn foo>", "bar 2", "bar 1", "<fn bar>", "<fn fooPrint>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 64: Pop(1) Stack: ["bar 1", "bar 2", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 1", "<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn bar>", "bar 2", "<Bar instance>", "<fn barPrint>", "<class Bar>", "<fn foo>", "<fn fooPrint>", "<class Foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: ["foo 2"]
[INFO] [stdout] Executing byte at 65: Nil Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "bar 2", "<fn barPrint>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "bar 1", "<Bar instance>", "<fn foo>", "<fn fooPrint>", "<class Bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 66: Return Stack: ["bar 1", "bar 2", "nil"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 1", "<fn fooPrint>", "<class Bar>", "<fn foo>", "<fn barPrint>", "<Bar instance>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<class Foo>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn foo>", "bar 2", "bar 1", "<fn bar>", "<fn bar>", "<class Bar>", "<class Foo>", "<Bar instance>", "<fn foo>", "<fn barPrint>", "<fn fooPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "bar 2", "<class Foo>", "bar 1", "<fn bar>", "<fn fooPrint>", "<fn foo>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<class Bar>", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: DotProperty(6) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 2", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<Bar instance>", "<fn fooPrint>", "bar 1", "<class Bar>", "<class Foo>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 29: Call { arg_count: 0 } Stack: ["<fn barPrint>"]
[INFO] [stdout] GC: checking value "<fn barPrint>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "bar 2", "<Bar instance>", "<fn bar>", "<fn foo>", "bar 1", "<class Bar>", "<fn fooPrint>", "<class Foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 68: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "bar 1", "<fn foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "<class Foo>", "bar 2", "<fn foo>", "<fn barPrint>", "<class Bar>", "<fn bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 69: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 2", "<class Foo>", "<fn fooPrint>", "<fn foo>", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "bar 1", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<Bar instance>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 70: Print Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "bar 1", "<fn foo>", "<fn barPrint>", "bar 2", "<fn fooPrint>", "<fn barPrint>", "<Bar instance>", "<class Foo>", "<fn foo>", "<class Bar>", "<fn bar>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 71: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn foo>", "bar 1", "<fn barPrint>", "<class Bar>", "<fn bar>", "<fn fooPrint>", "<fn fooPrint>", "<fn foo>", "<class Foo>", "<fn barPrint>", "bar 2", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 72: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "bar 2", "bar 1", "<class Foo>", "<fn bar>", "<fn fooPrint>", "<class Bar>", "<fn foo>", "<fn barPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 73: Print Stack: ["bar 2"]
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn bar>", "<fn foo>", "<Bar instance>", "bar 1", "<fn fooPrint>", "<fn fooPrint>", "<fn bar>", "<class Foo>", "<fn foo>", "<fn barPrint>", "bar 2", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 74: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "bar 2", "<class Foo>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<Bar instance>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "bar 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 75: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn fooPrint>", "<class Bar>", "<fn foo>", "<class Foo>", "<fn barPrint>", "<fn foo>", "bar 2", "bar 1", "<fn bar>", "<fn barPrint>", "<Bar instance>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 30: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "bar 2", "<class Foo>", "<class Bar>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<fn foo>", "bar 1", "<fn bar>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "bar 1", "bar 2", "<class Bar>", "<fn fooPrint>", "<fn foo>", "<class Foo>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "bar 1", "<fn foo>", "<fn fooPrint>", "<fn foo>", "bar 2", "<fn bar>", "<class Bar>", "<class Foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: Call { arg_count: 0 } Stack: ["<fn fooPrint>"]
[INFO] [stdout] GC: checking value "<fn fooPrint>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn fooPrint>", "<class Bar>", "<fn foo>", "<fn foo>", "bar 2", "<class Foo>", "<fn barPrint>", "<fn barPrint>", "<fn bar>", "<fn bar>", "<fn fooPrint>", "bar 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "bar 2", "<fn fooPrint>", "<Bar instance>", "<fn foo>", "bar 1", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<fn barPrint>", "<fn bar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<Bar instance>", "<fn foo>", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "bar 2", "<fn foo>", "<fn barPrint>", "bar 1", "<fn fooPrint>", "<fn bar>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 39: Print Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<Bar instance>", "bar 2", "<fn bar>", "<fn foo>", "bar 1", "<fn fooPrint>", "<fn fooPrint>", "<class Foo>", "<fn barPrint>", "<class Bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<class Bar>", "<fn foo>", "<fn barPrint>", "bar 1", "<fn foo>", "<fn fooPrint>", "<Bar instance>", "<fn bar>", "<class Foo>", "bar 2", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "bar 2", "bar 1", "<class Foo>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "<fn bar>", "<fn foo>", "<fn barPrint>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Print Stack: ["bar 2"]
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 2", "<Bar instance>", "bar 1", "<fn fooPrint>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<class Bar>", "<fn barPrint>", "<class Foo>", "<fn foo>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 43: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 1", "bar 2", "<fn fooPrint>", "<fn fooPrint>", "<class Bar>", "<class Foo>", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<Bar instance>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 44: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn barPrint>", "<class Bar>", "<fn barPrint>", "<class Foo>", "<fn foo>", "<fn bar>", "<Bar instance>", "<fn foo>", "bar 1", "<fn fooPrint>", "<fn bar>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 34: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<class Bar>", "bar 1", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<fn foo>", "bar 2", "<class Foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 35: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn fooPrint>", "<fn foo>", "bar 1", "<Bar instance>", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "<class Foo>", "bar 2", "<fn bar>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 5,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Callable { address: 5, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<fn foo>"]
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Nil,
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Nil Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Constant(
[INFO] [stdout]         123.0,
[INFO] [stdout]     ),
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Constant(123.0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["123"]
[INFO] [stdout] GC: checking value "123"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "123"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 24,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 29,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 3,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 34,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 39,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         4,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 3 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn methodOnFoo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 6, superclass: Some(Global(3)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn override>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 7: CreateGlobal { string_index: 6 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<class Bar>", "<fn methodOnBar>", "<fn override>", "<fn override>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: GetGlobal { string_index: 6 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<class Foo>", "<fn methodOnFoo>", "<class Bar>", "<fn override>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<class Foo>", "<fn methodOnBar>", "<fn override>", "<fn override>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: CreateGlobal { string_index: 5 } Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn methodOnBar>", "<Bar instance>", "<fn override>", "<fn methodOnBar>", "<fn override>", "<fn methodOnFoo>", "<class Bar>", "<class Foo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<class Foo>", "<fn methodOnFoo>", "<fn override>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn methodOnBar>", "<Bar instance>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn override>", "<fn methodOnBar>", "<class Bar>", "<fn override>", "<fn methodOnFoo>", "<fn override>", "<Bar instance>", "<fn methodOnFoo>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: Call { arg_count: 0 } Stack: ["<fn methodOnFoo>"]
[INFO] [stdout] GC: checking value "<fn methodOnFoo>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn override>", "<class Foo>", "<fn override>", "<fn methodOnBar>", "<fn methodOnBar>", "<fn override>", "<Bar instance>", "<class Bar>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 25: String(1) Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<class Bar>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn override>", "<class Foo>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: Print Stack: ["foo"]
[INFO] [stdout] GC: checking value "foo"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn methodOnBar>", "<fn methodOnBar>", "foo", "<fn override>", "<class Foo>", "<fn methodOnFoo>", "<fn override>", "<class Bar>", "<Bar instance>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: Nil Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<class Foo>", "<fn methodOnFoo>", "<class Bar>", "<fn override>", "<fn methodOnBar>", "<Bar instance>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn override>"]
[INFO] [stdout] FREEING: ["foo"]
[INFO] [stdout] Executing byte at 28: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn override>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnBar>", "<class Foo>", "<fn override>", "<Bar instance>", "<class Bar>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn methodOnBar>", "<fn override>", "<Bar instance>", "<fn methodOnFoo>", "<class Bar>", "<fn override>", "<fn methodOnBar>", "<fn override>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<fn override>", "<fn override>", "<Bar instance>", "<fn methodOnBar>", "<class Bar>", "<fn override>", "<class Foo>", "<fn methodOnFoo>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: DotProperty(4) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn methodOnBar>", "<Bar instance>", "<class Foo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: Call { arg_count: 0 } Stack: ["<fn methodOnBar>"]
[INFO] [stdout] GC: checking value "<fn methodOnBar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<class Foo>", "<Bar instance>", "<class Bar>", "<fn methodOnBar>", "<fn override>", "<fn methodOnFoo>", "<fn override>", "<fn override>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 35: String(5) Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn override>", "<class Foo>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn methodOnBar>", "<fn override>", "<class Bar>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 36: Print Stack: ["bar"]
[INFO] [stdout] GC: checking value "bar"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn override>", "<fn override>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn methodOnBar>", "<class Foo>", "<fn override>", "bar"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: Nil Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn override>", "<fn methodOnBar>", "<Bar instance>", "<fn methodOnFoo>", "<class Foo>", "<fn methodOnBar>", "<class Bar>", "<fn override>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: ["bar"]
[INFO] [stdout] Executing byte at 38: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<class Bar>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn methodOnBar>", "<fn override>", "<fn methodOnFoo>", "<fn override>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnBar>", "<fn override>", "<Bar instance>", "<fn methodOnBar>", "<fn override>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn override>", "<class Bar>", "<fn methodOnFoo>", "<fn override>", "<class Foo>", "<fn methodOnBar>", "<fn methodOnFoo>", "<fn methodOnBar>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<class Bar>", "<fn override>", "<Bar instance>", "<fn methodOnBar>", "<class Foo>", "<fn override>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: Call { arg_count: 0 } Stack: ["<fn override>"]
[INFO] [stdout] GC: checking value "<fn override>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<class Foo>", "<class Bar>", "<fn override>", "<fn methodOnFoo>", "<fn override>", "<Bar instance>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: String(5) Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<Bar instance>", "<class Foo>", "<class Bar>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<fn override>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: Print Stack: ["bar"]
[INFO] [stdout] GC: checking value "bar"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar", "<class Foo>", "<fn methodOnBar>", "<fn override>", "<Bar instance>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnBar>", "<fn override>", "<fn methodOnFoo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Nil Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn override>", "<fn override>", "<fn override>", "<class Foo>", "<fn methodOnBar>", "<fn methodOnFoo>", "<Bar instance>", "<fn methodOnFoo>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: ["bar"]
[INFO] [stdout] Executing byte at 43: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<class Bar>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn override>", "<Bar instance>", "<fn methodOnFoo>", "<class Foo>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<class Foo>", "<fn methodOnBar>", "<fn override>", "<class Bar>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<fn methodOnBar>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn override>", "<class Bar>", "<class Foo>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 2,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 30,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 8,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 5,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 35,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 8,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 8,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 9,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         4,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         7,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 2, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 2 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Class { name_index: 5, superclass: Some(Global(2)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 5 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Foo>", "<fn inBar>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Class { name_index: 8, superclass: Some(Global(5)) } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inFoo>", "<fn inBar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Bar>
[INFO] [stdout] Executing byte at 8: CreateGlobal { string_index: 8 } Stack: ["<class Baz>"]
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<class Foo>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: GetGlobal { string_index: 8 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inBar>", "<class Foo>", "<fn inBaz>", "<fn inFoo>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: Call { arg_count: 0 } Stack: ["<class Baz>"]
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inBaz>", "<class Baz>", "<fn inFoo>", "<fn inBar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: CreateGlobal { string_index: 9 } Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<class Foo>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<fn inFoo>", "<Baz instance>", "<class Bar>", "<fn inBar>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inBar>", "<fn inFoo>", "<class Baz>", "<class Bar>", "<Baz instance>", "<fn inBaz>", "<class Foo>", "<fn inFoo>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: DotProperty(0) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>", "<Baz instance>", "<class Baz>", "<fn inBar>", "<fn inBar>", "<fn inBaz>", "<fn inFoo>", "<fn inBaz>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: Call { arg_count: 0 } Stack: ["<fn inFoo>"]
[INFO] [stdout] GC: checking value "<fn inFoo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<fn inBar>", "<fn inBaz>", "<Baz instance>", "<class Bar>", "<fn inBar>", "<fn inFoo>", "<class Foo>", "<class Baz>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: String(1) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn inFoo>", "<Baz instance>", "<class Baz>", "<fn inBar>", "<fn inBar>", "<fn inBaz>", "<fn inFoo>", "<class Foo>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: Print Stack: ["in foo"]
[INFO] [stdout] GC: checking value "in foo"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<class Foo>", "<Baz instance>", "<fn inBar>", "<fn inBaz>", "<class Bar>", "in foo", "<fn inFoo>", "<fn inBar>", "<fn inFoo>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<fn inBar>", "<class Foo>", "<fn inBar>", "<class Baz>", "<Baz instance>", "<fn inFoo>", "<fn inBaz>", "<fn inBaz>", "<class Bar>"]
[INFO] [stdout] FREEING: ["in foo"]
[INFO] [stdout] Executing byte at 29: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<class Foo>", "<Baz instance>", "<fn inBar>", "<class Bar>", "<fn inBar>", "<fn inFoo>", "<fn inBaz>", "<class Baz>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<fn inBaz>", "<fn inFoo>", "<fn inBaz>", "<class Bar>", "<fn inBar>", "<class Foo>", "<Baz instance>", "<fn inBar>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Bar>", "<Baz instance>", "<fn inBaz>", "<class Baz>", "<class Foo>", "<fn inBaz>", "<fn inBar>", "<fn inFoo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: DotProperty(3) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<class Bar>", "<fn inBaz>", "<fn inFoo>", "<Baz instance>", "<fn inBar>", "<fn inFoo>", "<fn inBaz>", "<class Baz>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: Call { arg_count: 0 } Stack: ["<fn inBar>"]
[INFO] [stdout] GC: checking value "<fn inBar>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<fn inFoo>", "<fn inBar>", "<Baz instance>", "<fn inBaz>", "<fn inBaz>", "<class Bar>", "<fn inFoo>", "<class Foo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: String(4) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<Baz instance>", "<fn inFoo>", "<fn inBaz>", "<class Foo>", "<fn inFoo>", "<fn inBar>", "<class Baz>", "<class Bar>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: Print Stack: ["in bar"]
[INFO] [stdout] GC: checking value "in bar"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<fn inBar>", "<class Baz>", "<Baz instance>", "<fn inBaz>", "<class Foo>", "<fn inFoo>", "<class Bar>", "in bar", "<fn inFoo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<class Baz>", "<fn inBaz>", "<fn inBar>", "<fn inBaz>", "<fn inFoo>", "<fn inFoo>", "<class Foo>", "<Baz instance>", "<fn inBar>", "<class Bar>"]
[INFO] [stdout] FREEING: ["in bar"]
[INFO] [stdout] Executing byte at 34: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<class Foo>", "<fn inFoo>", "<Baz instance>", "<class Baz>", "<fn inFoo>", "<fn inBar>", "<fn inBar>", "<fn inBaz>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inFoo>", "<fn inBaz>", "<fn inBar>", "<class Foo>", "<fn inFoo>", "<Baz instance>", "<fn inBaz>", "<class Bar>", "<class Baz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: GetGlobal { string_index: 9 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBaz>", "<class Bar>", "<class Foo>", "<fn inFoo>", "<fn inBar>", "<Baz instance>", "<fn inBar>", "<class Baz>", "<fn inBaz>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: DotProperty(6) Stack: ["<Baz instance>"]
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inFoo>", "<fn inBaz>", "<class Bar>", "<class Baz>", "<class Foo>", "<fn inFoo>", "<Baz instance>", "<fn inBaz>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: Call { arg_count: 0 } Stack: ["<fn inBaz>"]
[INFO] [stdout] GC: checking value "<fn inBaz>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inFoo>", "<fn inBar>", "<class Baz>", "<Baz instance>", "<class Foo>", "<class Bar>", "<fn inBaz>", "<fn inFoo>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 36: String(7) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<fn inBaz>", "<class Bar>", "<class Baz>", "<fn inBaz>", "<class Foo>", "<fn inBar>", "<Baz instance>", "<fn inFoo>", "<fn inBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: Print Stack: ["in baz"]
[INFO] [stdout] GC: checking value "in baz"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<Baz instance>", "<class Bar>", "<class Baz>", "in baz", "<fn inFoo>", "<fn inBar>", "<fn inBaz>", "<class Foo>", "<fn inFoo>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Bar>", "<fn inBar>", "<fn inFoo>", "<Baz instance>", "<fn inBaz>", "<class Foo>", "<class Baz>", "<fn inBar>", "<fn inBaz>"]
[INFO] [stdout] FREEING: ["in baz"]
[INFO] [stdout] Executing byte at 39: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn inFoo>", "<class Baz>", "<fn inBar>", "<fn inBar>", "<fn inBaz>", "<fn inBaz>", "<class Bar>", "<fn inFoo>", "<Baz instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inBar>", "<fn inFoo>", "<class Foo>", "<Baz instance>", "<fn inBar>", "<class Baz>", "<fn inBaz>", "<fn inFoo>", "<class Bar>", "<fn inBaz>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 24: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Baz instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Baz>"
[INFO] [stdout] GC: reachable: ["<fn inFoo>", "<class Foo>", "<class Bar>", "<fn inBar>", "<fn inBaz>", "<Baz instance>", "<class Baz>", "<fn inBar>", "<fn inBaz>", "<fn inFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 8,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 0, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Callable { address: 8, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 1 } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn f>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn f>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Class { name_index: 2, superclass: Some(Global(0)) } Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: GetLocal(1) Stack: ["<fn f>", "<class B>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<class A>", "<class B>", "<fn f>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: Return Stack: ["<fn f>", "<class B>", "<class B>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn f>", "<class B>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Print Stack: ["<class B>"]
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<class B>", "<fn f>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] GC: reachable: ["<fn f>", "<class A>"]
[INFO] [stdout] FREEING: ["<class B>"]
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 15,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 21,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 3,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 3 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<fn test>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 4, superclass: Some(Global(3)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<fn test>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class B>"]
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<fn test>", "<class B>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: String(5) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class B>", "<class A>", "<fn test>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: GetGlobal { string_index: 4 } Stack: ["value"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class B>", "value", "<fn init>", "<fn test>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Call { arg_count: 1 } Stack: ["value", "<class B>"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<fn test>", "<class B>", "value", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: GetCaptured(0) Stack: ["value"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class B>", "<class A>", "<fn test>", "<fn init>", "value"]
[INFO] [stdout] FREEING: ["<fn init>", "<fn test>", "<B instance>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_14_inheritance' (32) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x61294af2cefa - std[1209cfb42f6d497f]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x61294af2cefa - std[1209cfb42f6d497f]::backtrace_rs::backtrace::trace_unsynchronized::<std[1209cfb42f6d497f]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x61294af2cefa - std[1209cfb42f6d497f]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x61294af2cefa - <<std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[4a92201981c6b77a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x61294af440ca - <core[4a92201981c6b77a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x61294af440ca - core[4a92201981c6b77a]::fmt::write
[INFO] [stdout]    6:     0x61294af31982 - std[1209cfb42f6d497f]::io::default_write_fmt::<alloc[420bb310a727dfd7]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x61294af31982 - <alloc[420bb310a727dfd7]::vec::Vec<u8> as std[1209cfb42f6d497f]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x61294af0b89f - <std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x61294af0b89f - std[1209cfb42f6d497f]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x61294af24e19 - std[1209cfb42f6d497f]::panicking::default_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x61294ac4a58c - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x61294ac4a58c - test[59e67a9bc0e119f1]::test_main_inner::<test[59e67a9bc0e119f1]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x61294af24fd2 - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x61294af24fd2 - std[1209cfb42f6d497f]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x61294af0b958 - std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x61294af00989 - std[1209cfb42f6d497f]::sys::backtrace::__rust_end_short_backtrace::<std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x61294af0c66d - __rustc[4effac393e9f3da6]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x61294af4490c - core[4a92201981c6b77a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x61294af44604 - core[4a92201981c6b77a]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x61294af44604 - core[4a92201981c6b77a]::option::expect_failed
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x61294ac11dc0 - <core[4a92201981c6b77a]::option::Option<&RustLox[f6ee2a17f4eb2240]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x61294ac30d3e - <RustLox[f6ee2a17f4eb2240]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x61294abff8fd - <RustLox[f6ee2a17f4eb2240]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x61294ac0251a - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x61294ac04b22 - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x61294ac0ef8a - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x61294ac26c46 - RustLox[f6ee2a17f4eb2240]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x61294ac2b8ea - <RustLox[f6ee2a17f4eb2240]::test::test_interpret_file::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x61294ac29771 - RustLox[f6ee2a17f4eb2240]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x61294ac294fa - RustLox[f6ee2a17f4eb2240]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x61294ac28436 - RustLox[f6ee2a17f4eb2240]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x61294ac315a2 - RustLox[f6ee2a17f4eb2240]::test::test_module::case_14_inheritance
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x61294ac2dc97 - RustLox[f6ee2a17f4eb2240]::test::test_module::case_14_inheritance::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x61294ac2bfa6 - <RustLox[f6ee2a17f4eb2240]::test::test_module::case_14_inheritance::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x61294ac3d86b - <fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x61294ac3d86b - test[59e67a9bc0e119f1]::__rust_begin_short_backtrace::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x61294ac4b05b - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x61294ac4b05b - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x61294ac4b05b - std[1209cfb42f6d497f]::panicking::catch_unwind::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x61294ac4b05b - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test_in_process
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x61294ac44774 - test[59e67a9bc0e119f1]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x61294ac44774 - std[1209cfb42f6d497f]::sys::backtrace::__rust_begin_short_backtrace::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x61294ac4dc62 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x61294ac4dc62 - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panicking::catch_unwind::<(), core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x61294ac4dc62 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x61294ac4dc62 - <std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x61294af2c4af - <alloc[420bb310a727dfd7]::boxed::Box<dyn core[4a92201981c6b77a]::ops::function::FnOnce<(), Output = ()> + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   54:     0x61294af2c4af - <std[1209cfb42f6d497f]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x7be15ac36aa4 - <unknown>
[INFO] [stdout]   56:     0x7be15acc3a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_25_this stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 15,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 42,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Captured(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Captured(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 20,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Captured(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 28,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 35,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 1 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn getClosure>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<class Foo>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn getClosure>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: DotProperty(2) Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn getClosure>", "<class Foo>", "<Foo instance>", "<fn getClosure>", "<fn toString>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<fn getClosure>"]
[INFO] [stdout] GC: checking value "<fn getClosure>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn toString>", "<fn getClosure>", "<fn toString>", "<Foo instance>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 43: Callable { address: 35, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<fn getClosure>", "<class Foo>"]
[INFO] [stdout] FREEING: ["<fn getClosure>", "<Foo instance>", "<fn toString>"]
[INFO] [stdout] Executing byte at 44: GetLocal(0) Stack: ["<fn f>"]
[INFO] [stdout] GC: checking value "<fn f>"
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_25_this' (43) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x61294af2cefa - std[1209cfb42f6d497f]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x61294af2cefa - std[1209cfb42f6d497f]::backtrace_rs::backtrace::trace_unsynchronized::<std[1209cfb42f6d497f]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x61294af2cefa - std[1209cfb42f6d497f]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x61294af2cefa - <<std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[4a92201981c6b77a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x61294af440ca - <core[4a92201981c6b77a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x61294af440ca - core[4a92201981c6b77a]::fmt::write
[INFO] [stdout]    6:     0x61294af31982 - std[1209cfb42f6d497f]::io::default_write_fmt::<alloc[420bb310a727dfd7]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x61294af31982 - <alloc[420bb310a727dfd7]::vec::Vec<u8> as std[1209cfb42f6d497f]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x61294af0b89f - <std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x61294af0b89f - std[1209cfb42f6d497f]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x61294af24e19 - std[1209cfb42f6d497f]::panicking::default_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x61294ac4a58c - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x61294ac4a58c - test[59e67a9bc0e119f1]::test_main_inner::<test[59e67a9bc0e119f1]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x61294af24fd2 - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x61294af24fd2 - std[1209cfb42f6d497f]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x61294af0b958 - std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x61294af00989 - std[1209cfb42f6d497f]::sys::backtrace::__rust_end_short_backtrace::<std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x61294af0c66d - __rustc[4effac393e9f3da6]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x61294af4490c - core[4a92201981c6b77a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x61294af44604 - core[4a92201981c6b77a]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x61294af44604 - core[4a92201981c6b77a]::option::expect_failed
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x61294ac11dc0 - <core[4a92201981c6b77a]::option::Option<&RustLox[f6ee2a17f4eb2240]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x61294ac30d3e - <RustLox[f6ee2a17f4eb2240]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x61294ac31902 - <RustLox[f6ee2a17f4eb2240]::heap::Heap>::gc::dfs
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:136:35
[INFO] [stdout]   24:     0x61294ac319c4 - <RustLox[f6ee2a17f4eb2240]::heap::Heap>::gc::dfs
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:137:17
[INFO] [stdout]   25:     0x61294ac2cbde - <RustLox[f6ee2a17f4eb2240]::heap::Heap>::gc::<core[4a92201981c6b77a]::iter::adapters::chain::Chain<core[4a92201981c6b77a]::slice::iter::Iter<RustLox[f6ee2a17f4eb2240]::value::Value>, std[1209cfb42f6d497f]::collections::hash::map::Values<alloc[420bb310a727dfd7]::string::String, RustLox[f6ee2a17f4eb2240]::value::Value>>>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:146:17
[INFO] [stdout]   26:     0x61294ac0483e - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:438:23
[INFO] [stdout]   27:     0x61294ac0ef8a - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   28:     0x61294ac26c46 - RustLox[f6ee2a17f4eb2240]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   29:     0x61294ac2b8ea - <RustLox[f6ee2a17f4eb2240]::test::test_interpret_file::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   30:     0x61294ac29771 - RustLox[f6ee2a17f4eb2240]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   31:     0x61294ac294fa - RustLox[f6ee2a17f4eb2240]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   32:     0x61294ac28436 - RustLox[f6ee2a17f4eb2240]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   33:     0x61294ac310a2 - RustLox[f6ee2a17f4eb2240]::test::test_module::case_25_this
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   34:     0x61294ac2d8d7 - RustLox[f6ee2a17f4eb2240]::test::test_module::case_25_this::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   35:     0x61294ac2baa6 - <RustLox[f6ee2a17f4eb2240]::test::test_module::case_25_this::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x61294ac3d86b - <fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   37:     0x61294ac3d86b - test[59e67a9bc0e119f1]::__rust_begin_short_backtrace::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:724:18
[INFO] [stdout]   38:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:74
[INFO] [stdout]   39:     0x61294ac4b05b - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40:     0x61294ac4b05b - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x61294ac4b05b - std[1209cfb42f6d497f]::panicking::catch_unwind::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x61294ac4b05b - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test_in_process
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:27
[INFO] [stdout]   44:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:668:43
[INFO] [stdout]   45:     0x61294ac44774 - test[59e67a9bc0e119f1]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:698:41
[INFO] [stdout]   46:     0x61294ac44774 - std[1209cfb42f6d497f]::sys::backtrace::__rust_begin_short_backtrace::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   47:     0x61294ac4dc62 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   48:     0x61294ac4dc62 - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   49:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   50:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panicking::catch_unwind::<(), core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   51:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   52:     0x61294ac4dc62 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   53:     0x61294ac4dc62 - <std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   54:     0x61294af2c4af - <alloc[420bb310a727dfd7]::boxed::Box<dyn core[4a92201981c6b77a]::ops::function::FnOnce<(), Output = ()> + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   55:     0x61294af2c4af - <std[1209cfb42f6d497f]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   56:     0x7be15ac36aa4 - <unknown>
[INFO] [stdout]   57:     0x7be15acc3a64 - clone
[INFO] [stdout]   58:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_24_super stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 12,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 10,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 4,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 10,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 10,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Add,
[INFO] [stdout]     Add,
[INFO] [stdout]     Add,
[INFO] [stdout]     Add,
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     String(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         7,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         8,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         9,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 4 } Stack: ["<class Base>"]
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Class { name_index: 10, superclass: Some(Global(4)) } Stack: []
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Base>
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 10 } Stack: ["<class Derived>"]
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<class Derived>", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: GetGlobal { string_index: 10 } Stack: []
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<class Derived>", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<class Derived>"]
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<fn foo>", "<fn foo>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: DotProperty(0) Stack: ["<Derived instance>"]
[INFO] [stdout] GC: checking value "<Derived instance>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<Derived instance>", "<fn foo>", "<class Base>", "<fn foo>", "<class Derived>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<fn foo>"]
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Derived>", "<class Base>", "<Derived instance>", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: String(5) Stack: []
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn foo>", "<class Derived>", "<fn foo>"]
[INFO] [stdout] FREEING: ["<Derived instance>", "<fn foo>"]
[INFO] [stdout] Executing byte at 27: Print Stack: ["Derived.foo()"]
[INFO] [stdout] GC: checking value "Derived.foo()"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["Derived.foo()", "<class Derived>", "<fn foo>", "<class Base>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: String(6) Stack: []
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn foo>", "<class Base>", "<class Derived>"]
[INFO] [stdout] FREEING: ["Derived.foo()"]
[INFO] [stdout] Executing byte at 29: String(7) Stack: ["a"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn foo>", "<fn foo>", "a", "<class Derived>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 30: String(8) Stack: ["a", "b"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Derived>", "b", "<fn foo>", "<class Base>", "a"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: String(9) Stack: ["a", "b", "c"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "c"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["c", "<class Base>", "a", "b", "<fn foo>", "<fn foo>", "<class Derived>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: GetCaptured(1) Stack: ["a", "b", "c", "d"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "c"
[INFO] [stdout] GC: checking value "d"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<fn foo>", "<class Base>", "c", "b", "d", "a", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: DotProperty(0) Stack: ["a", "b", "c", "d", "super"]
[INFO] [stdout] GC: checking value "a"
[INFO] [stdout] GC: checking value "b"
[INFO] [stdout] GC: checking value "c"
[INFO] [stdout] GC: checking value "d"
[INFO] [stdout] GC: checking value "super"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "d", "<class Derived>", "c", "a", "<fn foo>", "b", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_24_super' (42) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x61294af2cefa - std[1209cfb42f6d497f]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x61294af2cefa - std[1209cfb42f6d497f]::backtrace_rs::backtrace::trace_unsynchronized::<std[1209cfb42f6d497f]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x61294af2cefa - std[1209cfb42f6d497f]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x61294af2cefa - <<std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[4a92201981c6b77a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x61294af440ca - <core[4a92201981c6b77a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x61294af440ca - core[4a92201981c6b77a]::fmt::write
[INFO] [stdout]    6:     0x61294af31982 - std[1209cfb42f6d497f]::io::default_write_fmt::<alloc[420bb310a727dfd7]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x61294af31982 - <alloc[420bb310a727dfd7]::vec::Vec<u8> as std[1209cfb42f6d497f]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x61294af0b89f - <std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x61294af0b89f - std[1209cfb42f6d497f]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x61294af24e19 - std[1209cfb42f6d497f]::panicking::default_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x61294ac4a58c - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x61294ac4a58c - test[59e67a9bc0e119f1]::test_main_inner::<test[59e67a9bc0e119f1]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x61294af24fd2 - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x61294af24fd2 - std[1209cfb42f6d497f]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x61294af0b958 - std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x61294af00989 - std[1209cfb42f6d497f]::sys::backtrace::__rust_end_short_backtrace::<std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x61294af0c66d - __rustc[4effac393e9f3da6]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x61294af4490c - core[4a92201981c6b77a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x61294af44604 - core[4a92201981c6b77a]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x61294af44604 - core[4a92201981c6b77a]::option::expect_failed
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x61294ac11dc0 - <core[4a92201981c6b77a]::option::Option<&RustLox[f6ee2a17f4eb2240]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x61294ac30d3e - <RustLox[f6ee2a17f4eb2240]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x61294abff8fd - <RustLox[f6ee2a17f4eb2240]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x61294ac0251a - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x61294ac07203 - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:800:49
[INFO] [stdout]   26:     0x61294ac0ef8a - <RustLox[f6ee2a17f4eb2240]::interpreter::Interpreter<&mut alloc[420bb310a727dfd7]::string::String, &mut alloc[420bb310a727dfd7]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x61294ac26c46 - RustLox[f6ee2a17f4eb2240]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x61294ac2b8ea - <RustLox[f6ee2a17f4eb2240]::test::test_interpret_file::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x61294ac29771 - RustLox[f6ee2a17f4eb2240]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x61294ac294fa - RustLox[f6ee2a17f4eb2240]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x61294ac28436 - RustLox[f6ee2a17f4eb2240]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x61294ac311e2 - RustLox[f6ee2a17f4eb2240]::test::test_module::case_24_super
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x61294ac2d9c7 - RustLox[f6ee2a17f4eb2240]::test::test_module::case_24_super::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x61294ac2bbe6 - <RustLox[f6ee2a17f4eb2240]::test::test_module::case_24_super::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x61294ac3d86b - <fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x61294ac3d86b - test[59e67a9bc0e119f1]::__rust_begin_short_backtrace::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:724:18
[INFO] [stdout]   37:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:74
[INFO] [stdout]   38:     0x61294ac4b05b - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x61294ac4b05b - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x61294ac4b05b - std[1209cfb42f6d497f]::panicking::catch_unwind::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x61294ac4b05b - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test_in_process
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:27
[INFO] [stdout]   43:     0x61294ac4b05b - test[59e67a9bc0e119f1]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:668:43
[INFO] [stdout]   44:     0x61294ac44774 - test[59e67a9bc0e119f1]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:698:41
[INFO] [stdout]   45:     0x61294ac44774 - std[1209cfb42f6d497f]::sys::backtrace::__rust_begin_short_backtrace::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x61294ac4dc62 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   47:     0x61294ac4dc62 - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panicking::catch_unwind::<(), core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x61294ac4dc62 - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x61294ac4dc62 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   52:     0x61294ac4dc62 - <std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x61294af2c4af - <alloc[420bb310a727dfd7]::boxed::Box<dyn core[4a92201981c6b77a]::ops::function::FnOnce<(), Output = ()> + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   54:     0x61294af2c4af - <std[1209cfb42f6d497f]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x7be15ac36aa4 - <unknown>
[INFO] [stdout]   56:     0x7be15acc3a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test::test_module::case_05_class
[INFO] [stdout]     test::test_module::case_08_constructor
[INFO] [stdout]     test::test_module::case_14_inheritance
[INFO] [stdout]     test::test_module::case_24_super
[INFO] [stdout]     test::test_module::case_25_this
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 26 passed; 5 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.58s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--bin RustLox`
[INFO] running `Command { std: "docker" "inspect" "ac56f06acd70e4c38b0060416afb4bb3d81dbcec2b834df1943ca55249541842", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ac56f06acd70e4c38b0060416afb4bb3d81dbcec2b834df1943ca55249541842", kill_on_drop: false }`
[INFO] [stdout] ac56f06acd70e4c38b0060416afb4bb3d81dbcec2b834df1943ca55249541842
